04-24 15:36:07 D/ModuleListener: ModuleListener.testStarted(com.google.android.rvis.tvts.TvtsRvisTest#testRvisLaunchLegacyAudioRecord) on SKBFXYZO0DTQ
04-24 15:36:07 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-24 15:36:07 D/DeviceHostUtils: apiLevel: 31
04-24 15:36:07 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:36:08 D/RunUtil: Running command null without timeout.
04-24 15:36:08 D/AppRunnerUtils: Checking service: com.google.android.tv.remote.service/.RemoteService
04-24 15:36:08 D/AppRunnerUtils: Running the following command: dumpsys activity service com.google.android.tv.remote.service/.RemoteService
04-24 15:36:08 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, dumpsys, activity, service, com.google.android.tv.remote.service/.RemoteService] with timeout: 2m 0s
04-24 15:36:08 I/AppRunnerUtils: Service PID = 15493
04-24 15:36:08 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
04-24 15:36:08 D/DeviceHostUtils: apiLevel: 31
04-24 15:36:08 I/TvtsRvisTest: KEYCODE_TO_VP_SHOWN	KEYCODE_TO_READING (In Milliseconds)
04-24 15:36:08 D/RunUtil: Running command null without timeout.
04-24 15:36:08 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:36:09 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:09 D/RunUtil: Running command null without timeout.
04-24 15:36:09 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:36:09 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
04-24 15:36:09 D/ListInstrumentationParser: instrumentation:com.google.android.jank.home.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.jank.home.tvts)
04-24 15:36:09 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
04-24 15:36:09 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
04-24 15:36:09 D/ListInstrumentationParser: 
04-24 15:36:09 I/RemoteAndroidTest: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
04-24 15:36:11 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
04-24 15:36:11 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:36:12 I/TvtsRvisTest:                 154	               526
04-24 15:36:12 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:36:12 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:12 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:12 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:12 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:13 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:13 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:13 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:13 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:13 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:13 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:14 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:14 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:14 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:14 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:14 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:14 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:14 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:14 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:15 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:15 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:15 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:15 D/RunUtil: Running command null without timeout.
04-24 15:36:15 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:36:15 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
04-24 15:36:15 D/ListInstrumentationParser: instrumentation:com.google.android.jank.home.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.jank.home.tvts)
04-24 15:36:15 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
04-24 15:36:15 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
04-24 15:36:15 D/ListInstrumentationParser: 
04-24 15:36:15 I/RemoteAndroidTest: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
04-24 15:36:18 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
04-24 15:36:18 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:36:18 I/TvtsRvisTest:                  12	               183
04-24 15:36:18 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:36:18 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:19 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:19 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:19 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:19 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:19 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:19 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:19 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:20 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:20 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:20 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:20 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:20 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:20 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:21 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:21 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:21 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:21 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:21 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:21 D/RunUtil: Running command null without timeout.
04-24 15:36:21 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:36:22 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
04-24 15:36:22 D/ListInstrumentationParser: instrumentation:com.google.android.jank.home.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.jank.home.tvts)
04-24 15:36:22 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
04-24 15:36:22 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
04-24 15:36:22 D/ListInstrumentationParser: 
04-24 15:36:22 I/RemoteAndroidTest: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
04-24 15:36:24 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
04-24 15:36:24 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:36:24 I/TvtsRvisTest:                   9	               159
04-24 15:36:24 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:36:24 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:24 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:25 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:25 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:25 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:25 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:25 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:25 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:25 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:26 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:26 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:26 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:26 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:26 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:26 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:26 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:27 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:27 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:27 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:27 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:27 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:27 D/RunUtil: Running command null without timeout.
04-24 15:36:27 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:36:28 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
04-24 15:36:28 D/ListInstrumentationParser: instrumentation:com.google.android.jank.home.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.jank.home.tvts)
04-24 15:36:28 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
04-24 15:36:28 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
04-24 15:36:28 D/ListInstrumentationParser: 
04-24 15:36:28 I/RemoteAndroidTest: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
04-24 15:36:30 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
04-24 15:36:30 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:36:30 I/TvtsRvisTest:                  20	               170
04-24 15:36:30 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:36:30 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:31 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:31 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:31 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:31 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:31 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:31 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:31 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:32 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:32 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:32 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:32 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:32 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:32 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:33 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:33 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:33 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:33 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:33 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:33 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:33 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:34 D/RunUtil: Running command null without timeout.
04-24 15:36:34 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:36:34 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
04-24 15:36:34 D/ListInstrumentationParser: instrumentation:com.google.android.jank.home.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.jank.home.tvts)
04-24 15:36:34 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
04-24 15:36:34 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
04-24 15:36:34 D/ListInstrumentationParser: 
04-24 15:36:34 I/RemoteAndroidTest: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
04-24 15:36:36 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
04-24 15:36:36 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:36:36 I/TvtsRvisTest:                  10	               164
04-24 15:36:36 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:36:37 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:37 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:37 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:37 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:37 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:37 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:38 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:38 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:38 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:38 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:38 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:38 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:38 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:38 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:39 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:39 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:39 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:39 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:39 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:39 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:40 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:40 D/RunUtil: Running command null without timeout.
04-24 15:36:40 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:36:40 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
04-24 15:36:40 D/ListInstrumentationParser: instrumentation:com.google.android.jank.home.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.jank.home.tvts)
04-24 15:36:40 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
04-24 15:36:40 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
04-24 15:36:40 D/ListInstrumentationParser: 
04-24 15:36:40 I/RemoteAndroidTest: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
04-24 15:36:42 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
04-24 15:36:42 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:36:43 I/TvtsRvisTest:                   8	               159
04-24 15:36:43 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:36:43 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:43 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:43 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:43 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:43 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:43 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:44 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:44 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:44 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:44 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:44 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:44 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:45 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:45 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:45 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:45 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:45 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:45 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:45 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:46 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:46 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:46 D/RunUtil: Running command null without timeout.
04-24 15:36:46 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:36:46 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
04-24 15:36:46 D/ListInstrumentationParser: instrumentation:com.google.android.jank.home.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.jank.home.tvts)
04-24 15:36:46 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
04-24 15:36:46 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
04-24 15:36:46 D/ListInstrumentationParser: 
04-24 15:36:46 I/RemoteAndroidTest: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
04-24 15:36:48 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
04-24 15:36:48 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:36:49 I/TvtsRvisTest:                  10	               158
04-24 15:36:49 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:36:49 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:49 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:49 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:49 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:49 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:50 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:50 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:50 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:50 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:50 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:50 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:50 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:51 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:51 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:51 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:51 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:51 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:51 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:52 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:52 D/RunUtil: Running command null without timeout.
04-24 15:36:52 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:36:52 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
04-24 15:36:52 D/ListInstrumentationParser: instrumentation:com.google.android.jank.home.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.jank.home.tvts)
04-24 15:36:52 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
04-24 15:36:52 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
04-24 15:36:52 D/ListInstrumentationParser: 
04-24 15:36:52 I/RemoteAndroidTest: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
04-24 15:36:54 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
04-24 15:36:54 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:36:54 I/TvtsRvisTest:                   9	               145
04-24 15:36:54 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:36:55 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:55 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:55 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:55 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:55 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:55 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:56 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:56 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:56 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:56 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:56 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:56 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:56 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:57 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:57 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:57 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:57 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:57 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:57 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:57 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:36:58 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:36:58 D/RunUtil: Running command null without timeout.
04-24 15:36:58 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:36:58 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
04-24 15:36:58 D/ListInstrumentationParser: instrumentation:com.google.android.jank.home.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.jank.home.tvts)
04-24 15:36:58 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
04-24 15:36:58 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
04-24 15:36:58 D/ListInstrumentationParser: 
04-24 15:36:58 I/RemoteAndroidTest: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
04-24 15:37:00 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
04-24 15:37:00 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:37:01 I/TvtsRvisTest:                  10	               156
04-24 15:37:01 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:37:01 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:01 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:37:01 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:01 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:37:01 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:01 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:37:02 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:02 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:37:02 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:02 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:37:02 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:02 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:37:03 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:03 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:37:03 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:03 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:37:03 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:03 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:37:03 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:04 D/RunUtil: Running command null without timeout.
04-24 15:37:04 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -b, all, -c] with timeout: 2m 0s
04-24 15:37:04 D/ListInstrumentationParser: instrumentation:com.android.tradefed.utils.wifi/.WifiUtil (target=com.android.tradefed.utils.wifi)
04-24 15:37:04 D/ListInstrumentationParser: instrumentation:com.google.android.jank.home.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.jank.home.tvts)
04-24 15:37:04 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
04-24 15:37:04 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
04-24 15:37:04 D/ListInstrumentationParser: 
04-24 15:37:04 I/RemoteAndroidTest: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
04-24 15:37:06 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
04-24 15:37:06 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
04-24 15:37:06 I/TvtsRvisTest:                  12	               175
04-24 15:37:06 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
04-24 15:37:07 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:07 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:37:07 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:07 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:37:07 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:07 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:37:08 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:08 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:37:08 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:08 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:37:08 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:08 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:37:09 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:09 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:37:09 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:09 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:37:09 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:09 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:37:09 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:10 D/RunUtil: operation failed, waiting for 200 ms
04-24 15:37:10 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
04-24 15:37:10 I/TvtsRvisTest: numOfRuns: 10
04-24 15:37:10 I/TvtsRvisTest: numOfFailedRuns: 0
04-24 15:37:10 D/PrettyTestEventLogger: 
==================== com.google.android.rvis.tvts.TvtsRvisTest#testRvisLaunchLegacyAudioRecord ENDED: Wed Apr 24 15:37:10 KST 2024 ====================
